[10/24 11:46:07,Telerik.TestStudio.Desktop.exe(8828:1),Uncategorized] Orchestrator._iUIProject_TestSelected() : Test '1040 - Self Registration' selected.
[10/24 11:46:07,Telerik.TestStudio.Desktop.exe(8828:1),Uncategorized] Orchestrator._iUIProject_TestSelected() : Test '1040 - Self Registration' selected.
[10/24 11:46:10,Telerik.TestStudio.Desktop.exe(8828:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[10/24 11:46:10,ArtOfTest.Runner.exe(7148:3)] First trace message from process 7148: "C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=3436
[10/24 11:46:10,ArtOfTest.Runner.exe(7148:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 10300).
[10/24 11:46:10,ArtOfTest.Runner.exe(7148:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[10/24 11:46:12,ArtOfTest.Runner.exe(7148:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[10/24 11:46:12,ArtOfTest.Runner.exe(7148:4)] First trace message from pool unnamed thread (managed ID = 4, native ID = 8760).
[10/24 11:46:12,ArtOfTest.Runner.exe(7148:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=344, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[10/24 11:46:12,ArtOfTest.Runner.exe(7148:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=3212734)...
[10/24 11:46:13,ArtOfTest.Runner.exe(7148:4),Framework] Connector.Start() : Process launched (ID=5604, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 23200916").
[10/24 11:46:13,ArtOfTest.Runner.exe(7148:4),Framework] Connector.Start() : Process exited (ID=5604, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 23200916").
[10/24 11:46:13,ArtOfTest.Runner.exe(7148:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.Desktop" (ID=8828) is assumed to host the controller.
[10/24 11:46:13,ArtOfTest.Runner.exe(7148:4),Framework] Connector.InjectCode() : ------- Function Start -------
[10/24 11:46:13,ArtOfTest.Runner.exe(7148:4),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2016.2.630.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?8828
[10/24 11:46:13,ArtOfTest.Runner.exe(7148:4),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 23200916 on another thread...
[10/24 11:46:13,ArtOfTest.Runner.exe(7148:9)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 9, native ID = 5564).
[10/24 11:46:13,ArtOfTest.Runner.exe(7148:9),Framework] <>c__DisplayClass25_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[10/24 11:46:13,ArtOfTest.Runner.exe(7148:4),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[10/24 11:46:13,ArtOfTest.Runner.exe(7148:4),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 23200916 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2016.2.630.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?8828")...
[10/24 11:46:13,IEXPLORE.EXE(4468:1)] First trace message from process 4468: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:344 CREDAT:275457 /prefetch:2
[10/24 11:46:13,IEXPLORE.EXE(4468:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 10716).
[10/24 11:46:13,IEXPLORE.EXE(4468:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 23200916, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-1315935472", PID = 8828).
[10/24 11:46:13,IEXPLORE.EXE(4468:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 23200916 on same thread...
[10/24 11:46:14,IEXPLORE.EXE(4468:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[10/24 11:46:14,IEXPLORE.EXE(4468:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_475e2e77-fad4-4f05-9fae-28d2e1d34fb7".
[10/24 11:46:14,ArtOfTest.Runner.exe(7148:6)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 6, native ID = 10568).
[10/24 11:46:14,ArtOfTest.Runner.exe(7148:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[10/24 11:46:14,ArtOfTest.Runner.exe(7148:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 8104).
[10/24 11:46:14,ArtOfTest.Runner.exe(7148:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[10/24 11:46:14,IEXPLORE.EXE(4468:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "e8988f12-23e9-4f9c-be20-7a27ca890628.CommandPipe", Async = "e8988f12-23e9-4f9c-be20-7a27ca890628.AsyncPipe"}
[10/24 11:46:14,ArtOfTest.Runner.exe(7148:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[10/24 11:46:14,ArtOfTest.Runner.exe(7148:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[10/24 11:46:14,ArtOfTest.Runner.exe(7148:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_475e2e77-fad4-4f05-9fae-28d2e1d34fb7" (now there is/are 1 total).
[10/24 11:46:14,ArtOfTest.Runner.exe(7148:4),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[10/24 11:46:14,ArtOfTest.Runner.exe(7148:4),Framework] Connector.InjectCode() : ------- Function End -------
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: '1040 - Self Registration', path: 'Administrator\1040 - Self Registration.tstest.'
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Got 15 plugin(s) in folder "C:\Program Files (x86)\Telerik\Test Studio\Bin\Plugins".
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "LibGit2Sharp.dll" from plugins folder.
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Functional.dll" from plugins folder.
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.ProductGuide.dll" from plugins folder.
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.dll" from plugins folder.
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.dll" from plugins folder.
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.TS.dll" from plugins folder.
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.TeamPulse.dll" from plugins folder.
[10/24 11:46:15,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[10/24 11:46:16,ArtOfTest.Runner.exe(7148:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[10/24 11:46:16,ArtOfTest.Runner.exe(7148:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[10/24 11:46:16,ArtOfTest.Runner.exe(7148:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/24 11:46:16,ArtOfTest.Runner.exe(7148:4),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[10/24 11:46:16,ArtOfTest.Runner.exe(7148:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/24 11:46:16,ArtOfTest.Runner.exe(7148:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/24 11:46:16,ArtOfTest.Runner.exe(7148:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/24 11:46:16,ArtOfTest.Runner.exe(7148:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : '/'"...
[10/24 11:46:19,ArtOfTest.Runner.exe(7148:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : '/'".
[10/24 11:46:19,ArtOfTest.Runner.exe(7148:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/24 11:46:19,ArtOfTest.Runner.exe(7148:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/24 11:46:19,ArtOfTest.Runner.exe(7148:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/24 11:46:19,ArtOfTest.Runner.exe(7148:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/24 11:46:19,ArtOfTest.Runner.exe(7148:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/24 11:46:19,ArtOfTest.Runner.exe(7148:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "New Coded Step"...
